07-01 16:58:35 D/ModuleListener: ModuleListener.testStarted(com.google.android.assistant.v4.tvts.TvtsAssistant4Test#testHeavyDeviceUsage) on SKBFXYZO0DTQ
07-01 16:58:35 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:35 D/DeviceHostUtils: apiLevel: 29
07-01 16:58:35 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:35 D/DeviceHostUtils: apiLevel: 29
07-01 16:58:36 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:58:36 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.katniss
07-01 16:58:36 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.katniss] with timeout: 2m 0s
07-01 16:58:36 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:36 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:37 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:37 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:37 D/RunUtil: Running command null without timeout.
07-01 16:58:37 D/AppRunnerUtils: Checking service: com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService
07-01 16:58:37 D/AppRunnerUtils: Running the following command: dumpsys activity service com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService
07-01 16:58:37 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, dumpsys, activity, service, com.google.android.katniss/.search.serviceapi.KatnissVoiceInteractionService] with timeout: 2m 0s
07-01 16:58:37 I/AppRunnerUtils: Service PID = 4319
07-01 16:58:37 I/AssistantTestUtils: Waiting for assistant to be ready...
07-01 16:58:37 D/RunUtil: Running command [launch Assistant intent, then check if Assistant is in foreground] without timeout.
07-01 16:58:37 D/AssistantTestUtils: Launching Assistant intent
07-01 16:58:37 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
07-01 16:58:37 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
07-01 16:58:37 D/RunUtil: Running command [check if Assistant is in foreground] without timeout.
07-01 16:58:37 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:58:37 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 16:58:37 I/DeviceStateMonitor: Waiting 359837 ms for device SKBFXYZO0DTQ package manager
07-01 16:58:38 I/NativeDeviceStateMonitor: Waiting 359624 ms for device SKBFXYZO0DTQ external store
07-01 16:58:40 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity]
07-01 16:58:46 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.tvsearch.results.activity.SearchResultActivity.
07-01 16:58:46 D/AssistantTestUtils: Supplier "check if Assistant is in foreground" returned: true
07-01 16:58:46 D/AssistantTestUtils: Supplier "launch Assistant intent, then check if Assistant is in foreground" returned: true
07-01 16:58:46 D/AssistantTestUtils: Assistant successfully launched: true
07-01 16:58:46 D/RunUtil: Running command [logcat -d | grep -E 'AssistantWrapper(Impl)?: Assistant is now running.'] without timeout.
07-01 16:58:46 I/AssistantTestUtils: Assistant ready: true
07-01 16:58:46 D/RunUtil: Running command [launch Assistant intent, then check if Assistant is in foreground] without timeout.
07-01 16:58:46 D/AssistantTestUtils: Launching Assistant intent
07-01 16:58:46 D/AppRunnerUtils: Running the following command: am start --es query Weather -p com.google.android.katniss -c android.intent.category.DEFAULT -a android.intent.action.ASSIST
07-01 16:58:46 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, start, --es, query, Weather, -p, com.google.android.katniss, -c, android.intent.category.DEFAULT, -a, android.intent.action.ASSIST] with timeout: 2m 0s
07-01 16:58:46 D/RunUtil: Running command [check if Assistant is in foreground] without timeout.
07-01 16:58:46 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:58:46 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 16:58:46 I/DeviceStateMonitor: Waiting 359929 ms for device SKBFXYZO0DTQ package manager
07-01 16:58:47 I/NativeDeviceStateMonitor: Waiting 359775 ms for device SKBFXYZO0DTQ external store
07-01 16:58:48 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity]
07-01 16:58:48 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.google.android.apps.tvsearch.results.activity.SearchResultActivity.
07-01 16:58:48 D/AssistantTestUtils: Supplier "check if Assistant is in foreground" returned: true
07-01 16:58:48 D/AssistantTestUtils: Supplier "launch Assistant intent, then check if Assistant is in foreground" returned: true
07-01 16:58:48 D/AssistantTestUtils: Assistant successfully launched: true
07-01 16:58:48 D/RunUtil: Running command [check if metrics file pattern is present] without timeout.
07-01 16:58:48 I/AssistantTestUtils: Checking if file pattern is present: /storage/emulated/0/Download/katniss-primes*
07-01 16:58:48 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, ls, -t, -d, /storage/emulated/0/Download/katniss-primes*] with timeout: 2m 0s
07-01 16:58:49 D/AssistantTestUtils: Supplier "check if metrics file pattern is present" returned: true
07-01 16:58:49 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, ls, -t, -d, /storage/emulated/0/Download/katniss-primes*] with timeout: 2m 0s
07-01 16:58:49 D/AssistantTestUtils: Session-based metrics File: /storage/emulated/0/Download/katniss-primes-2024-07-01T00_58_46.235.txt
07-01 16:58:49 D/NativeDevice: Using 'ls' to check doesFileExist(/storage/emulated/0/Download/katniss-primes-2024-07-01T00_58_46.235.txt)
07-01 16:58:49 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:49 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_58_46.235_12667494469195736088.txt] without timeout.
07-01 16:58:49 D/TvtsBaseAssistantTest: Metrics location: /storage/emulated/0/Download/katniss-primes-2024-07-01T00_58_46.235.txt
07-01 16:58:49 D/TvtsBaseAssistantTest: Option enabling successful!
07-01 16:58:49 I/TvtsBaseAssistantTest: Test: HeavyDeviceUsageTest
07-01 16:58:49 I/MeasurementPrinter: search_to_query_latency	Measurement Status	query_to_result_latency	Measurement Status	query_to_result_no_server_latency	Measurement Status
07-01 16:58:49 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:58:49 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 16:58:49 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 16:58:49 I/NativeDeviceStateMonitor: Waiting 359865 ms for device SKBFXYZO0DTQ external store
07-01 16:58:50 D/RunUtil: Running command null without timeout.
07-01 16:58:50 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:58:50 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:58:50 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:58:50 D/RunUtil: Running command null without timeout.
07-01 16:58:50 D/AppRunnerUtils: foregroundActivitySet(): [com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity]
07-01 16:58:50 I/KeyCodeUtils: Sending keyevent KEYCODE_HOME - intValue: 3
07-01 16:58:51 D/AppRunnerUtils: [com.google.android.katniss/com.google.android.apps.tvsearch.results.activity.SearchResultActivity]
07-01 16:58:51 D/RunUtil: operation failed, waiting for 500 ms
07-01 16:58:52 D/RunUtil: Running command null without timeout.
07-01 16:58:52 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:58:52 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:58:52 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:58:53 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:58:53 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:58:53 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:58:53 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_58_46.235_15343115329704750380.txt] without timeout.
07-01 16:58:53 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:58:53 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:58:53 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:58:53 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:59:21 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:59:21 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:59:21 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3820.000000
07-01 16:59:21 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 947.000000
07-01 16:59:21 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 28.000000
07-01 16:59:21 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 589.000000
07-01 16:59:21 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 104.000000
07-01 16:59:21 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 8.000000
07-01 16:59:21 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 8.000000
07-01 16:59:21 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 120.000000
07-01 16:59:21 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3029.000000
07-01 16:59:21 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 55.000000
07-01 16:59:21 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 153.000000
07-01 16:59:21 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2860.000000
07-01 16:59:21 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 950.000000
07-01 16:59:21 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 24.000000
07-01 16:59:21 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 32.000000
07-01 16:59:21 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 35.000000
07-01 16:59:21 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 948.000000
07-01 16:59:21 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 81.000000
07-01 16:59:21 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 153.000000
07-01 16:59:21 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:59:21 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 17.000000
07-01 16:59:21 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:59:21 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:59:21 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3820.000000
07-01 16:59:21 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 947.000000
07-01 16:59:21 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 28.000000
07-01 16:59:21 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 589.000000
07-01 16:59:21 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 104.000000
07-01 16:59:21 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 8.000000
07-01 16:59:21 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 8.000000
07-01 16:59:21 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 120.000000
07-01 16:59:21 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3029.000000
07-01 16:59:21 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 55.000000
07-01 16:59:21 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 153.000000
07-01 16:59:21 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2860.000000
07-01 16:59:21 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:59:21 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 950.000000
07-01 16:59:21 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:59:21 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 24.000000
07-01 16:59:21 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 32.000000
07-01 16:59:21 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 35.000000
07-01 16:59:21 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 948.000000
07-01 16:59:21 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 81.000000
07-01 16:59:21 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 153.000000
07-01 16:59:21 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 17.000000
07-01 16:59:21 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:59:21 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:59:21 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3820.000000
07-01 16:59:21 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 947.000000
07-01 16:59:21 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 28.000000
07-01 16:59:21 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 589.000000
07-01 16:59:21 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 104.000000
07-01 16:59:21 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 8.000000
07-01 16:59:21 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 8.000000
07-01 16:59:21 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 120.000000
07-01 16:59:21 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3029.000000
07-01 16:59:21 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 55.000000
07-01 16:59:21 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 153.000000
07-01 16:59:21 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2860.000000
07-01 16:59:21 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 950.000000
07-01 16:59:21 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:59:21 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 24.000000
07-01 16:59:21 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 32.000000
07-01 16:59:21 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 35.000000
07-01 16:59:21 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 948.000000
07-01 16:59:21 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 81.000000
07-01 16:59:21 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 153.000000
07-01 16:59:21 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 17.000000
07-01 16:59:21 I/MeasurementPrinter:                   153.0	                OK	                 3810.0	                OK	                            950.0	                OK
07-01 16:59:21 D/RunUtil: Running command null without timeout.
07-01 16:59:21 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:59:21 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:59:21 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:59:22 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:59:22 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:59:22 D/RunUtil: Running command null without timeout.
07-01 16:59:22 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:59:22 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:59:22 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:59:22 D/RunUtil: Running command null without timeout.
07-01 16:59:22 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:59:22 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:59:22 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:59:22 I/NativeDeviceStateMonitor: Waiting 359999 ms for device SKBFXYZO0DTQ boot complete
07-01 16:59:22 I/DeviceStateMonitor: Waiting 359958 ms for device SKBFXYZO0DTQ package manager
07-01 16:59:23 I/NativeDeviceStateMonitor: Waiting 359897 ms for device SKBFXYZO0DTQ external store
07-01 16:59:24 D/RunUtil: Running command null without timeout.
07-01 16:59:24 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:59:24 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:59:24 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:59:24 D/RunUtil: Running command null without timeout.
07-01 16:59:24 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:59:24 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:59:24 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:59:25 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:59:25 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:59:25 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:59:25 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_58_46.235_2456475841322628257.txt] without timeout.
07-01 16:59:25 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:59:25 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:59:25 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:59:25 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 16:59:52 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:59:52 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 16:59:52 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3815.000000
07-01 16:59:52 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 794.000000
07-01 16:59:52 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 47.000000
07-01 16:59:52 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 854.000000
07-01 16:59:52 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 103.000000
07-01 16:59:52 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 10.000000
07-01 16:59:52 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 16:59:52 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 279.000000
07-01 16:59:52 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3079.000000
07-01 16:59:52 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 38.000000
07-01 16:59:52 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 115.000000
07-01 16:59:52 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 3014.000000
07-01 16:59:52 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 807.000000
07-01 16:59:52 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 32.000000
07-01 16:59:52 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 5.000000
07-01 16:59:52 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 30.000000
07-01 16:59:52 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 794.000000
07-01 16:59:52 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 66.000000
07-01 16:59:52 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 116.000000
07-01 16:59:52 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 16:59:52 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 46.000000
07-01 16:59:52 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:59:52 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 16:59:52 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3815.000000
07-01 16:59:52 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 794.000000
07-01 16:59:52 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 47.000000
07-01 16:59:52 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 854.000000
07-01 16:59:52 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 103.000000
07-01 16:59:52 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 10.000000
07-01 16:59:52 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 16:59:52 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 279.000000
07-01 16:59:52 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3079.000000
07-01 16:59:52 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 38.000000
07-01 16:59:52 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 115.000000
07-01 16:59:52 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 3014.000000
07-01 16:59:52 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 16:59:52 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 807.000000
07-01 16:59:52 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:59:52 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 32.000000
07-01 16:59:52 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 5.000000
07-01 16:59:52 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 30.000000
07-01 16:59:52 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 794.000000
07-01 16:59:52 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 66.000000
07-01 16:59:52 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 116.000000
07-01 16:59:52 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 46.000000
07-01 16:59:52 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:59:52 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 16:59:52 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3815.000000
07-01 16:59:52 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 794.000000
07-01 16:59:52 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 47.000000
07-01 16:59:52 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 854.000000
07-01 16:59:52 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 103.000000
07-01 16:59:52 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 10.000000
07-01 16:59:52 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 16:59:52 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 279.000000
07-01 16:59:52 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3079.000000
07-01 16:59:52 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 38.000000
07-01 16:59:52 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 115.000000
07-01 16:59:52 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 3014.000000
07-01 16:59:52 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 807.000000
07-01 16:59:52 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 16:59:52 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 32.000000
07-01 16:59:52 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 5.000000
07-01 16:59:52 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 30.000000
07-01 16:59:52 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 794.000000
07-01 16:59:52 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 66.000000
07-01 16:59:52 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 116.000000
07-01 16:59:52 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 46.000000
07-01 16:59:52 I/MeasurementPrinter:                   116.0	                OK	                 3821.0	                OK	                            807.0	                OK
07-01 16:59:52 D/RunUtil: Running command null without timeout.
07-01 16:59:52 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:59:53 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:59:53 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:59:53 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:59:54 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:59:54 D/RunUtil: Running command null without timeout.
07-01 16:59:54 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:59:54 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:59:54 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:59:54 D/RunUtil: Running command null without timeout.
07-01 16:59:54 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:59:54 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:59:54 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 16:59:54 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 16:59:54 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 16:59:54 I/NativeDeviceStateMonitor: Waiting 359897 ms for device SKBFXYZO0DTQ external store
07-01 16:59:55 D/RunUtil: Running command null without timeout.
07-01 16:59:55 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:59:55 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 16:59:55 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 16:59:55 D/RunUtil: Running command null without timeout.
07-01 16:59:55 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:59:55 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:59:55 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 16:59:56 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 16:59:56 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 16:59:56 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 16:59:56 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_58_46.235_5932279107145540734.txt] without timeout.
07-01 16:59:56 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 16:59:56 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 16:59:56 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 16:59:56 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 17:00:24 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:00:24 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 17:00:24 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3618.000000
07-01 17:00:24 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 768.000000
07-01 17:00:24 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 53.000000
07-01 17:00:24 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 709.000000
07-01 17:00:24 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 59.000000
07-01 17:00:24 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 17:00:24 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 5.000000
07-01 17:00:24 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 453.000000
07-01 17:00:24 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2919.000000
07-01 17:00:24 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 37.000000
07-01 17:00:24 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 117.000000
07-01 17:00:24 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2845.000000
07-01 17:00:24 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 783.000000
07-01 17:00:24 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 26.000000
07-01 17:00:24 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 11.000000
07-01 17:00:24 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 28.000000
07-01 17:00:24 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 768.000000
07-01 17:00:24 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 73.000000
07-01 17:00:24 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 118.000000
07-01 17:00:24 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 17:00:24 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 15.000000
07-01 17:00:24 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:00:24 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 17:00:24 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3618.000000
07-01 17:00:24 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 768.000000
07-01 17:00:24 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 53.000000
07-01 17:00:24 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 709.000000
07-01 17:00:24 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 59.000000
07-01 17:00:24 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 17:00:24 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 5.000000
07-01 17:00:24 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 453.000000
07-01 17:00:24 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2919.000000
07-01 17:00:24 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 37.000000
07-01 17:00:24 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 117.000000
07-01 17:00:24 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2845.000000
07-01 17:00:24 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 17:00:24 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 783.000000
07-01 17:00:24 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:00:24 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 26.000000
07-01 17:00:24 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 11.000000
07-01 17:00:24 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 28.000000
07-01 17:00:24 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 768.000000
07-01 17:00:24 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 73.000000
07-01 17:00:24 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 118.000000
07-01 17:00:24 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 15.000000
07-01 17:00:24 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:00:24 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 17:00:24 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3618.000000
07-01 17:00:24 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 768.000000
07-01 17:00:24 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 53.000000
07-01 17:00:24 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 709.000000
07-01 17:00:24 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 59.000000
07-01 17:00:24 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 17:00:24 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 5.000000
07-01 17:00:24 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 453.000000
07-01 17:00:24 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2919.000000
07-01 17:00:24 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 37.000000
07-01 17:00:24 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 117.000000
07-01 17:00:24 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2845.000000
07-01 17:00:24 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 783.000000
07-01 17:00:24 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:00:24 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 26.000000
07-01 17:00:24 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 11.000000
07-01 17:00:24 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 28.000000
07-01 17:00:24 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 768.000000
07-01 17:00:24 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 73.000000
07-01 17:00:24 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 118.000000
07-01 17:00:24 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 15.000000
07-01 17:00:24 I/MeasurementPrinter:                   118.0	                OK	                 3628.0	                OK	                            783.0	                OK
07-01 17:00:24 D/RunUtil: Running command null without timeout.
07-01 17:00:24 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:00:25 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:00:25 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:00:25 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:00:25 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:00:25 D/RunUtil: Running command null without timeout.
07-01 17:00:25 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:00:25 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:00:25 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:00:25 D/RunUtil: Running command null without timeout.
07-01 17:00:25 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:00:25 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:00:25 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 17:00:25 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 17:00:25 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 17:00:26 I/NativeDeviceStateMonitor: Waiting 359877 ms for device SKBFXYZO0DTQ external store
07-01 17:00:27 D/RunUtil: Running command null without timeout.
07-01 17:00:27 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:00:27 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:00:27 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:00:27 D/RunUtil: Running command null without timeout.
07-01 17:00:27 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:00:27 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:00:27 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:00:27 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:00:27 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 17:00:28 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:00:28 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_58_46.235_1323436698607354688.txt] without timeout.
07-01 17:00:28 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 17:00:28 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 17:00:28 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 17:00:28 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 17:00:54 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:00:54 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 17:00:54 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3164.000000
07-01 17:00:54 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 792.000000
07-01 17:00:54 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 24.000000
07-01 17:00:54 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 480.000000
07-01 17:00:54 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 133.000000
07-01 17:00:54 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 17:00:54 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 17.000000
07-01 17:00:54 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 308.000000
07-01 17:00:54 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2435.000000
07-01 17:00:54 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 59.000000
07-01 17:00:54 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 173.000000
07-01 17:00:54 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2354.000000
07-01 17:00:54 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 795.000000
07-01 17:00:54 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 18.000000
07-01 17:00:54 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 41.000000
07-01 17:00:54 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 9.000000
07-01 17:00:54 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 792.000000
07-01 17:00:54 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 95.000000
07-01 17:00:54 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 175.000000
07-01 17:00:54 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 17:00:54 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 14.000000
07-01 17:00:54 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:00:54 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 17:00:54 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3164.000000
07-01 17:00:54 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 792.000000
07-01 17:00:54 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 24.000000
07-01 17:00:54 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 480.000000
07-01 17:00:54 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 133.000000
07-01 17:00:54 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 17:00:54 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 17.000000
07-01 17:00:54 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 308.000000
07-01 17:00:54 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2435.000000
07-01 17:00:54 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 59.000000
07-01 17:00:54 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 173.000000
07-01 17:00:54 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2354.000000
07-01 17:00:54 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 17:00:54 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 795.000000
07-01 17:00:54 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:00:54 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 18.000000
07-01 17:00:54 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 41.000000
07-01 17:00:54 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 9.000000
07-01 17:00:54 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 792.000000
07-01 17:00:54 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 95.000000
07-01 17:00:54 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 175.000000
07-01 17:00:54 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 14.000000
07-01 17:00:54 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:00:54 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 17:00:54 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3164.000000
07-01 17:00:54 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 792.000000
07-01 17:00:54 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 24.000000
07-01 17:00:54 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 480.000000
07-01 17:00:54 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 133.000000
07-01 17:00:54 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 17:00:54 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 17.000000
07-01 17:00:54 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 308.000000
07-01 17:00:54 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2435.000000
07-01 17:00:54 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 59.000000
07-01 17:00:54 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 173.000000
07-01 17:00:54 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2354.000000
07-01 17:00:54 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 795.000000
07-01 17:00:54 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:00:54 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 18.000000
07-01 17:00:54 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 41.000000
07-01 17:00:54 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 9.000000
07-01 17:00:54 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 792.000000
07-01 17:00:54 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 95.000000
07-01 17:00:54 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 175.000000
07-01 17:00:54 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 14.000000
07-01 17:00:54 I/MeasurementPrinter:                   175.0	                OK	                 3149.0	                OK	                            795.0	                OK
07-01 17:00:54 D/RunUtil: Running command null without timeout.
07-01 17:00:55 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:00:55 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:00:55 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:00:55 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:00:56 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:00:56 D/RunUtil: Running command null without timeout.
07-01 17:00:56 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:00:56 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:00:56 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:00:56 D/RunUtil: Running command null without timeout.
07-01 17:00:56 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:00:56 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:00:56 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 17:00:56 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 17:00:56 I/DeviceStateMonitor: Waiting 359963 ms for device SKBFXYZO0DTQ package manager
07-01 17:00:56 I/NativeDeviceStateMonitor: Waiting 359876 ms for device SKBFXYZO0DTQ external store
07-01 17:00:57 D/RunUtil: Running command null without timeout.
07-01 17:00:57 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:00:57 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:00:57 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:00:57 D/RunUtil: Running command null without timeout.
07-01 17:00:58 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:00:58 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:00:58 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:00:58 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:00:58 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 17:00:58 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:00:58 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_58_46.235_13007686082695256214.txt] without timeout.
07-01 17:00:59 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 17:00:59 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 17:00:59 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 17:00:59 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 17:01:26 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:01:26 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 17:01:26 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3750.000000
07-01 17:01:26 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 783.000000
07-01 17:01:26 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 76.000000
07-01 17:01:26 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 486.000000
07-01 17:01:26 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 109.000000
07-01 17:01:26 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 12.000000
07-01 17:01:26 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 17:01:26 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 209.000000
07-01 17:01:26 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3051.000000
07-01 17:01:26 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 67.000000
07-01 17:01:26 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 147.000000
07-01 17:01:26 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2959.000000
07-01 17:01:26 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 790.000000
07-01 17:01:26 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 18.000000
07-01 17:01:26 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 49.000000
07-01 17:01:26 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 17.000000
07-01 17:01:26 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 785.000000
07-01 17:01:26 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 67.000000
07-01 17:01:26 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 148.000000
07-01 17:01:26 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 17:01:26 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 17.000000
07-01 17:01:26 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:01:26 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 17:01:26 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3750.000000
07-01 17:01:26 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 783.000000
07-01 17:01:26 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 76.000000
07-01 17:01:26 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 486.000000
07-01 17:01:26 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 109.000000
07-01 17:01:26 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 12.000000
07-01 17:01:26 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 17:01:26 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 209.000000
07-01 17:01:26 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3051.000000
07-01 17:01:26 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 67.000000
07-01 17:01:26 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 147.000000
07-01 17:01:26 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2959.000000
07-01 17:01:26 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 17:01:26 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 790.000000
07-01 17:01:26 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:01:26 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 18.000000
07-01 17:01:26 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 49.000000
07-01 17:01:26 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 17.000000
07-01 17:01:26 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 785.000000
07-01 17:01:26 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 67.000000
07-01 17:01:26 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 148.000000
07-01 17:01:26 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 17.000000
07-01 17:01:26 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:01:26 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 17:01:26 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3750.000000
07-01 17:01:26 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 783.000000
07-01 17:01:26 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 76.000000
07-01 17:01:26 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 486.000000
07-01 17:01:26 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 109.000000
07-01 17:01:26 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 12.000000
07-01 17:01:26 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 17:01:26 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 209.000000
07-01 17:01:26 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3051.000000
07-01 17:01:26 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 67.000000
07-01 17:01:26 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 147.000000
07-01 17:01:26 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2959.000000
07-01 17:01:26 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 790.000000
07-01 17:01:26 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:01:26 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 18.000000
07-01 17:01:26 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 49.000000
07-01 17:01:26 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 17.000000
07-01 17:01:26 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 785.000000
07-01 17:01:26 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 67.000000
07-01 17:01:26 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 148.000000
07-01 17:01:26 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 17.000000
07-01 17:01:26 I/MeasurementPrinter:                   148.0	                OK	                 3749.0	                OK	                            790.0	                OK
07-01 17:01:26 D/RunUtil: Running command null without timeout.
07-01 17:01:26 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:01:27 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:01:27 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:01:27 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:01:28 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:01:28 D/RunUtil: Running command null without timeout.
07-01 17:01:28 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:01:28 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:01:28 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:01:28 D/RunUtil: Running command null without timeout.
07-01 17:01:28 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:01:28 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:01:28 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 17:01:28 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 17:01:28 I/DeviceStateMonitor: Waiting 359965 ms for device SKBFXYZO0DTQ package manager
07-01 17:01:28 I/NativeDeviceStateMonitor: Waiting 359878 ms for device SKBFXYZO0DTQ external store
07-01 17:01:29 D/RunUtil: Running command null without timeout.
07-01 17:01:29 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:01:29 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:01:29 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:01:29 D/RunUtil: Running command null without timeout.
07-01 17:01:29 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:01:29 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:01:29 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:01:30 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:01:30 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 17:01:30 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:01:30 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_58_46.235_12045902521311284781.txt] without timeout.
07-01 17:01:30 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 17:01:30 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 17:01:30 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 17:01:30 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 17:01:57 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:01:57 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 17:01:57 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3733.000000
07-01 17:01:57 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 896.000000
07-01 17:01:57 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 21.000000
07-01 17:01:57 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 671.000000
07-01 17:01:57 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 111.000000
07-01 17:01:57 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 17:01:57 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 29.000000
07-01 17:01:57 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 196.000000
07-01 17:01:57 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2880.000000
07-01 17:01:57 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 19.000000
07-01 17:01:57 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 150.000000
07-01 17:01:57 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2802.000000
07-01 17:01:57 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 908.000000
07-01 17:01:57 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 15.000000
07-01 17:01:57 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 4.000000
07-01 17:01:57 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 30.000000
07-01 17:01:57 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 901.000000
07-01 17:01:57 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 100.000000
07-01 17:01:57 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 152.000000
07-01 17:01:57 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 17:01:57 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 50.000000
07-01 17:01:57 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:01:57 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 17:01:57 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3733.000000
07-01 17:01:57 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 896.000000
07-01 17:01:57 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 21.000000
07-01 17:01:57 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 671.000000
07-01 17:01:57 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 111.000000
07-01 17:01:57 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 17:01:57 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 29.000000
07-01 17:01:57 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 196.000000
07-01 17:01:57 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2880.000000
07-01 17:01:57 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 19.000000
07-01 17:01:57 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 150.000000
07-01 17:01:57 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2802.000000
07-01 17:01:57 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 17:01:57 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 908.000000
07-01 17:01:57 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:01:57 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 15.000000
07-01 17:01:57 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 4.000000
07-01 17:01:57 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 30.000000
07-01 17:01:57 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 901.000000
07-01 17:01:57 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 100.000000
07-01 17:01:57 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 152.000000
07-01 17:01:57 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 50.000000
07-01 17:01:57 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:01:58 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 17:01:58 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3733.000000
07-01 17:01:58 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 896.000000
07-01 17:01:58 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 21.000000
07-01 17:01:58 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 671.000000
07-01 17:01:58 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 111.000000
07-01 17:01:58 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 17:01:58 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 29.000000
07-01 17:01:58 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 196.000000
07-01 17:01:58 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2880.000000
07-01 17:01:58 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 19.000000
07-01 17:01:58 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 150.000000
07-01 17:01:58 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2802.000000
07-01 17:01:58 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 908.000000
07-01 17:01:58 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:01:58 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 15.000000
07-01 17:01:58 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 4.000000
07-01 17:01:58 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 30.000000
07-01 17:01:58 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 901.000000
07-01 17:01:58 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 100.000000
07-01 17:01:58 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 152.000000
07-01 17:01:58 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 50.000000
07-01 17:01:58 I/MeasurementPrinter:                   152.0	                OK	                 3710.0	                OK	                            908.0	                OK
07-01 17:01:58 D/RunUtil: Running command null without timeout.
07-01 17:01:58 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:01:58 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:01:58 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:01:58 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:01:59 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:01:59 D/RunUtil: Running command null without timeout.
07-01 17:01:59 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:01:59 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:01:59 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:01:59 D/RunUtil: Running command null without timeout.
07-01 17:01:59 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:01:59 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:01:59 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 17:01:59 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 17:01:59 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 17:01:59 I/NativeDeviceStateMonitor: Waiting 359878 ms for device SKBFXYZO0DTQ external store
07-01 17:02:00 D/RunUtil: Running command null without timeout.
07-01 17:02:00 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:02:00 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:02:00 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:02:00 D/RunUtil: Running command null without timeout.
07-01 17:02:00 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:02:01 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:02:01 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:02:01 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:02:01 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 17:02:01 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:02:01 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_58_46.235_5269189970864747124.txt] without timeout.
07-01 17:02:02 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 17:02:02 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 17:02:02 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 17:02:02 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 17:02:28 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:02:29 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 17:02:29 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3564.000000
07-01 17:02:29 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 806.000000
07-01 17:02:29 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 76.000000
07-01 17:02:29 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 508.000000
07-01 17:02:29 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 124.000000
07-01 17:02:29 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 15.000000
07-01 17:02:29 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 10.000000
07-01 17:02:29 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 304.000000
07-01 17:02:29 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2810.000000
07-01 17:02:29 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 26.000000
07-01 17:02:29 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 102.000000
07-01 17:02:29 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2735.000000
07-01 17:02:29 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 822.000000
07-01 17:02:29 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 15.000000
07-01 17:02:29 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 12.000000
07-01 17:02:29 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 10.000000
07-01 17:02:29 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 807.000000
07-01 17:02:29 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 50.000000
07-01 17:02:29 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 103.000000
07-01 17:02:29 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 17:02:29 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 109.000000
07-01 17:02:29 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:02:29 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 17:02:29 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3564.000000
07-01 17:02:29 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 806.000000
07-01 17:02:29 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 76.000000
07-01 17:02:29 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 508.000000
07-01 17:02:29 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 124.000000
07-01 17:02:29 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 15.000000
07-01 17:02:29 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 10.000000
07-01 17:02:29 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 304.000000
07-01 17:02:29 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2810.000000
07-01 17:02:29 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 26.000000
07-01 17:02:29 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 102.000000
07-01 17:02:29 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2735.000000
07-01 17:02:29 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 17:02:29 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 822.000000
07-01 17:02:29 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:02:29 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 15.000000
07-01 17:02:29 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 12.000000
07-01 17:02:29 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 10.000000
07-01 17:02:29 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 807.000000
07-01 17:02:29 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 50.000000
07-01 17:02:29 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 103.000000
07-01 17:02:29 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 109.000000
07-01 17:02:29 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:02:29 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 17:02:29 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3564.000000
07-01 17:02:29 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 806.000000
07-01 17:02:29 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 76.000000
07-01 17:02:29 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 508.000000
07-01 17:02:29 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 124.000000
07-01 17:02:29 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 15.000000
07-01 17:02:29 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 10.000000
07-01 17:02:29 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 304.000000
07-01 17:02:29 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2810.000000
07-01 17:02:29 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 26.000000
07-01 17:02:29 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 102.000000
07-01 17:02:29 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2735.000000
07-01 17:02:29 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 822.000000
07-01 17:02:29 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:02:29 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 15.000000
07-01 17:02:29 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 12.000000
07-01 17:02:29 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 10.000000
07-01 17:02:29 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 807.000000
07-01 17:02:29 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 50.000000
07-01 17:02:29 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 103.000000
07-01 17:02:29 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 109.000000
07-01 17:02:29 I/MeasurementPrinter:                   103.0	                OK	                 3557.0	                OK	                            822.0	                OK
07-01 17:02:29 D/RunUtil: Running command null without timeout.
07-01 17:02:29 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:02:29 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:02:29 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:02:29 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:02:30 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:02:30 D/RunUtil: Running command null without timeout.
07-01 17:02:30 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:02:30 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:02:30 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:02:30 D/RunUtil: Running command null without timeout.
07-01 17:02:30 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:02:30 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:02:30 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 17:02:30 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 17:02:30 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 17:02:30 I/NativeDeviceStateMonitor: Waiting 359897 ms for device SKBFXYZO0DTQ external store
07-01 17:02:32 D/RunUtil: Running command null without timeout.
07-01 17:02:32 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:02:32 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:02:32 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:02:32 D/RunUtil: Running command null without timeout.
07-01 17:02:32 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:02:32 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:02:32 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:02:32 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:02:32 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 17:02:32 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:02:33 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_58_46.235_1789213951159257240.txt] without timeout.
07-01 17:02:33 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 17:02:33 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 17:02:33 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 17:02:33 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 17:03:00 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:03:00 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 17:03:00 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3609.000000
07-01 17:03:00 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 815.000000
07-01 17:03:00 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 30.000000
07-01 17:03:00 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 459.000000
07-01 17:03:00 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 82.000000
07-01 17:03:00 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 6.000000
07-01 17:03:00 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 17:03:00 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2824.000000
07-01 17:03:00 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 38.000000
07-01 17:03:00 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 143.000000
07-01 17:03:00 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2764.000000
07-01 17:03:00 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 817.000000
07-01 17:03:00 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 19.000000
07-01 17:03:00 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 19.000000
07-01 17:03:00 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 18.000000
07-01 17:03:00 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 817.000000
07-01 17:03:00 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 97.000000
07-01 17:03:00 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 143.000000
07-01 17:03:00 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 17:03:00 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 31.000000
07-01 17:03:00 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:03:00 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 17:03:00 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3609.000000
07-01 17:03:00 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 815.000000
07-01 17:03:00 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 30.000000
07-01 17:03:00 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 459.000000
07-01 17:03:00 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 82.000000
07-01 17:03:00 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 6.000000
07-01 17:03:00 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 17:03:00 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2824.000000
07-01 17:03:00 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 38.000000
07-01 17:03:00 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 143.000000
07-01 17:03:00 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2764.000000
07-01 17:03:00 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 17:03:00 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 817.000000
07-01 17:03:00 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:03:00 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 19.000000
07-01 17:03:00 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 19.000000
07-01 17:03:00 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 18.000000
07-01 17:03:00 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 817.000000
07-01 17:03:00 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 97.000000
07-01 17:03:00 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 143.000000
07-01 17:03:00 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 31.000000
07-01 17:03:00 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:03:00 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 17:03:00 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3609.000000
07-01 17:03:00 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 815.000000
07-01 17:03:00 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 30.000000
07-01 17:03:00 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 459.000000
07-01 17:03:00 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 82.000000
07-01 17:03:00 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 6.000000
07-01 17:03:00 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 17:03:00 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2824.000000
07-01 17:03:00 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 38.000000
07-01 17:03:00 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 143.000000
07-01 17:03:00 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2764.000000
07-01 17:03:00 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 817.000000
07-01 17:03:00 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:03:00 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 19.000000
07-01 17:03:00 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 19.000000
07-01 17:03:00 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 18.000000
07-01 17:03:00 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 817.000000
07-01 17:03:00 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 97.000000
07-01 17:03:00 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 143.000000
07-01 17:03:00 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 31.000000
07-01 17:03:00 I/MeasurementPrinter:                   143.0	                OK	                 3581.0	                OK	                            817.0	                OK
07-01 17:03:00 D/RunUtil: Running command null without timeout.
07-01 17:03:00 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:03:01 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:03:01 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:03:01 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:03:02 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:03:02 D/RunUtil: Running command null without timeout.
07-01 17:03:02 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:03:02 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:03:02 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:03:02 D/RunUtil: Running command null without timeout.
07-01 17:03:02 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:03:02 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:03:02 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 17:03:02 I/NativeDeviceStateMonitor: Waiting 359999 ms for device SKBFXYZO0DTQ boot complete
07-01 17:03:02 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 17:03:02 I/NativeDeviceStateMonitor: Waiting 359898 ms for device SKBFXYZO0DTQ external store
07-01 17:03:03 D/RunUtil: Running command null without timeout.
07-01 17:03:03 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:03:03 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:03:03 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:03:03 D/RunUtil: Running command null without timeout.
07-01 17:03:03 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:03:03 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:03:03 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:03:04 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:03:04 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 17:03:04 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:03:04 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_58_46.235_10041629511002385165.txt] without timeout.
07-01 17:03:04 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 17:03:04 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 17:03:04 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 17:03:04 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 17:03:31 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:03:31 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 17:03:31 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3322.000000
07-01 17:03:31 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 747.000000
07-01 17:03:31 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 4.000000
07-01 17:03:31 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 525.000000
07-01 17:03:31 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 85.000000
07-01 17:03:31 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 5.000000
07-01 17:03:31 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 11.000000
07-01 17:03:31 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 157.000000
07-01 17:03:31 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2661.000000
07-01 17:03:31 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 38.000000
07-01 17:03:31 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 166.000000
07-01 17:03:31 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2565.000000
07-01 17:03:31 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 759.000000
07-01 17:03:31 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 27.000000
07-01 17:03:31 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 11.000000
07-01 17:03:31 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 16.000000
07-01 17:03:31 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 748.000000
07-01 17:03:31 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 112.000000
07-01 17:03:31 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 168.000000
07-01 17:03:31 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 17:03:31 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 21.000000
07-01 17:03:31 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:03:31 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 17:03:31 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3322.000000
07-01 17:03:31 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 747.000000
07-01 17:03:31 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 4.000000
07-01 17:03:31 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 525.000000
07-01 17:03:31 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 85.000000
07-01 17:03:31 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 5.000000
07-01 17:03:31 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 11.000000
07-01 17:03:31 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 157.000000
07-01 17:03:31 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2661.000000
07-01 17:03:31 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 38.000000
07-01 17:03:31 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 166.000000
07-01 17:03:31 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2565.000000
07-01 17:03:31 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 17:03:31 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 759.000000
07-01 17:03:31 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:03:31 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 27.000000
07-01 17:03:31 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 11.000000
07-01 17:03:31 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 16.000000
07-01 17:03:31 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 748.000000
07-01 17:03:31 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 112.000000
07-01 17:03:31 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 168.000000
07-01 17:03:31 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 21.000000
07-01 17:03:31 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:03:32 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 17:03:32 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3322.000000
07-01 17:03:32 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 747.000000
07-01 17:03:32 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 4.000000
07-01 17:03:32 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 525.000000
07-01 17:03:32 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 85.000000
07-01 17:03:32 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 5.000000
07-01 17:03:32 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 11.000000
07-01 17:03:32 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 157.000000
07-01 17:03:32 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2661.000000
07-01 17:03:32 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 38.000000
07-01 17:03:32 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 166.000000
07-01 17:03:32 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2565.000000
07-01 17:03:32 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 759.000000
07-01 17:03:32 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:03:32 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 27.000000
07-01 17:03:32 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 11.000000
07-01 17:03:32 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 16.000000
07-01 17:03:32 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 748.000000
07-01 17:03:32 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 112.000000
07-01 17:03:32 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 168.000000
07-01 17:03:32 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 21.000000
07-01 17:03:32 I/MeasurementPrinter:                   168.0	                OK	                 3324.0	                OK	                            759.0	                OK
07-01 17:03:32 D/RunUtil: Running command null without timeout.
07-01 17:03:32 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:03:32 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:03:32 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:03:32 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:03:33 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:03:33 D/RunUtil: Running command null without timeout.
07-01 17:03:33 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:03:33 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:03:33 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:03:33 D/RunUtil: Running command null without timeout.
07-01 17:03:33 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:03:33 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:03:33 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 17:03:33 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 17:03:33 I/DeviceStateMonitor: Waiting 359959 ms for device SKBFXYZO0DTQ package manager
07-01 17:03:33 I/NativeDeviceStateMonitor: Waiting 359897 ms for device SKBFXYZO0DTQ external store
07-01 17:03:34 D/RunUtil: Running command null without timeout.
07-01 17:03:34 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:03:34 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:03:34 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:03:34 D/RunUtil: Running command null without timeout.
07-01 17:03:34 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:03:35 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:03:35 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:03:35 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:03:35 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 17:03:35 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:03:35 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_58_46.235_18007391864853674035.txt] without timeout.
07-01 17:03:36 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 17:03:36 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 17:03:36 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 17:03:36 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 17:04:02 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:04:02 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 17:04:02 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3699.000000
07-01 17:04:02 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 756.000000
07-01 17:04:02 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 45.000000
07-01 17:04:02 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 664.000000
07-01 17:04:02 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 37.000000
07-01 17:04:02 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 5.000000
07-01 17:04:02 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 17:04:02 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 140.000000
07-01 17:04:02 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3011.000000
07-01 17:04:02 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 36.000000
07-01 17:04:02 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 119.000000
07-01 17:04:02 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2933.000000
07-01 17:04:02 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 759.000000
07-01 17:04:02 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 20.000000
07-01 17:04:02 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 15.000000
07-01 17:04:02 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 23.000000
07-01 17:04:02 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 758.000000
07-01 17:04:02 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 78.000000
07-01 17:04:02 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 121.000000
07-01 17:04:02 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 17:04:02 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 24.000000
07-01 17:04:02 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:04:03 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 17:04:03 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3699.000000
07-01 17:04:03 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 756.000000
07-01 17:04:03 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 45.000000
07-01 17:04:03 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 664.000000
07-01 17:04:03 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 37.000000
07-01 17:04:03 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 5.000000
07-01 17:04:03 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 17:04:03 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 140.000000
07-01 17:04:03 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3011.000000
07-01 17:04:03 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 36.000000
07-01 17:04:03 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 119.000000
07-01 17:04:03 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2933.000000
07-01 17:04:03 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 17:04:03 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 759.000000
07-01 17:04:03 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:04:03 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 20.000000
07-01 17:04:03 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 15.000000
07-01 17:04:03 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 23.000000
07-01 17:04:03 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 758.000000
07-01 17:04:03 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 78.000000
07-01 17:04:03 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 121.000000
07-01 17:04:03 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 24.000000
07-01 17:04:03 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:04:03 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 17:04:03 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3699.000000
07-01 17:04:03 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 756.000000
07-01 17:04:03 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 45.000000
07-01 17:04:03 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 664.000000
07-01 17:04:03 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 37.000000
07-01 17:04:03 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 5.000000
07-01 17:04:03 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 17:04:03 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 140.000000
07-01 17:04:03 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3011.000000
07-01 17:04:03 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 36.000000
07-01 17:04:03 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 119.000000
07-01 17:04:03 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2933.000000
07-01 17:04:03 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 759.000000
07-01 17:04:03 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:04:03 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 20.000000
07-01 17:04:03 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 15.000000
07-01 17:04:03 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 23.000000
07-01 17:04:03 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 758.000000
07-01 17:04:03 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 78.000000
07-01 17:04:03 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 121.000000
07-01 17:04:03 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 24.000000
07-01 17:04:03 I/MeasurementPrinter:                   121.0	                OK	                 3692.0	                OK	                            759.0	                OK
07-01 17:04:03 D/RunUtil: Running command null without timeout.
07-01 17:04:03 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:04:03 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:04:03 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:04:03 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:04:04 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:04:04 D/RunUtil: Running command null without timeout.
07-01 17:04:04 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:04:04 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:04:04 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:04:04 D/RunUtil: Running command null without timeout.
07-01 17:04:04 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:04:04 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:04:04 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 17:04:04 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 17:04:04 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 17:04:04 I/NativeDeviceStateMonitor: Waiting 359898 ms for device SKBFXYZO0DTQ external store
07-01 17:04:05 D/RunUtil: Running command null without timeout.
07-01 17:04:05 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:04:06 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:04:06 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:04:06 D/RunUtil: Running command null without timeout.
07-01 17:04:06 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:04:06 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:04:06 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:04:06 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:04:06 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 17:04:06 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:04:06 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_58_46.235_6829056537531517747.txt] without timeout.
07-01 17:04:07 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 17:04:07 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 17:04:07 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 17:04:07 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 17:04:33 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:04:34 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 17:04:34 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3490.000000
07-01 17:04:34 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 762.000000
07-01 17:04:34 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 30.000000
07-01 17:04:34 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 509.000000
07-01 17:04:34 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 75.000000
07-01 17:04:34 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 25.000000
07-01 17:04:34 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 3.000000
07-01 17:04:34 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 51.000000
07-01 17:04:34 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2779.000000
07-01 17:04:34 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 38.000000
07-01 17:04:34 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 110.000000
07-01 17:04:34 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2717.000000
07-01 17:04:34 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 769.000000
07-01 17:04:34 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 23.000000
07-01 17:04:34 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 15.000000
07-01 17:04:34 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 21.000000
07-01 17:04:34 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 763.000000
07-01 17:04:34 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 44.000000
07-01 17:04:34 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 110.000000
07-01 17:04:34 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 17:04:34 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 23.000000
07-01 17:04:34 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:04:34 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 17:04:34 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3490.000000
07-01 17:04:34 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 762.000000
07-01 17:04:34 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 30.000000
07-01 17:04:34 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 509.000000
07-01 17:04:34 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 75.000000
07-01 17:04:34 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 25.000000
07-01 17:04:34 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 3.000000
07-01 17:04:34 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 51.000000
07-01 17:04:34 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2779.000000
07-01 17:04:34 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 38.000000
07-01 17:04:34 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 110.000000
07-01 17:04:34 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2717.000000
07-01 17:04:34 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 17:04:34 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 769.000000
07-01 17:04:34 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:04:34 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 23.000000
07-01 17:04:34 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 15.000000
07-01 17:04:34 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 21.000000
07-01 17:04:34 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 763.000000
07-01 17:04:34 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 44.000000
07-01 17:04:34 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 110.000000
07-01 17:04:34 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 23.000000
07-01 17:04:34 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:04:34 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 17:04:34 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3490.000000
07-01 17:04:34 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 762.000000
07-01 17:04:34 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 30.000000
07-01 17:04:34 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 509.000000
07-01 17:04:34 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 75.000000
07-01 17:04:34 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 25.000000
07-01 17:04:34 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 3.000000
07-01 17:04:34 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 51.000000
07-01 17:04:34 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2779.000000
07-01 17:04:34 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 38.000000
07-01 17:04:34 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 110.000000
07-01 17:04:34 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2717.000000
07-01 17:04:34 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 769.000000
07-01 17:04:34 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:04:34 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 23.000000
07-01 17:04:34 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 15.000000
07-01 17:04:34 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 21.000000
07-01 17:04:34 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 763.000000
07-01 17:04:34 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 44.000000
07-01 17:04:34 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 110.000000
07-01 17:04:34 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 23.000000
07-01 17:04:34 I/MeasurementPrinter:                   110.0	                OK	                 3486.0	                OK	                            769.0	                OK
07-01 17:04:34 D/RunUtil: Running command null without timeout.
07-01 17:04:34 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:04:34 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:04:34 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:04:35 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:04:35 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:04:35 D/RunUtil: Running command null without timeout.
07-01 17:04:35 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:04:35 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:04:35 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:04:35 D/RunUtil: Running command null without timeout.
07-01 17:04:35 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:04:35 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:04:35 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 17:04:35 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 17:04:35 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 17:04:36 I/NativeDeviceStateMonitor: Waiting 359902 ms for device SKBFXYZO0DTQ external store
07-01 17:04:37 D/RunUtil: Running command null without timeout.
07-01 17:04:37 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:04:37 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:04:37 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:04:37 D/RunUtil: Running command null without timeout.
07-01 17:04:37 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:04:37 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:04:37 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:04:38 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:04:38 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 17:04:38 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:04:38 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_58_46.235_6937841245349751282.txt] without timeout.
07-01 17:04:38 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 17:04:38 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 17:04:38 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 17:04:38 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 17:05:05 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:05:05 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 17:05:05 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3766.000000
07-01 17:05:05 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 779.000000
07-01 17:05:05 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 35.000000
07-01 17:05:05 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 672.000000
07-01 17:05:05 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 93.000000
07-01 17:05:05 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 10.000000
07-01 17:05:05 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 17:05:05 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 43.000000
07-01 17:05:05 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3045.000000
07-01 17:05:05 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 48.000000
07-01 17:05:05 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 159.000000
07-01 17:05:05 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2973.000000
07-01 17:05:05 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 784.000000
07-01 17:05:05 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 33.000000
07-01 17:05:05 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 15.000000
07-01 17:05:05 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 25.000000
07-01 17:05:05 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 779.000000
07-01 17:05:05 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 99.000000
07-01 17:05:05 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 160.000000
07-01 17:05:05 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 17:05:05 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 20.000000
07-01 17:05:05 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:05:05 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 17:05:05 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3766.000000
07-01 17:05:05 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 779.000000
07-01 17:05:05 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 35.000000
07-01 17:05:05 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 672.000000
07-01 17:05:05 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 93.000000
07-01 17:05:05 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 10.000000
07-01 17:05:05 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 17:05:05 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 43.000000
07-01 17:05:05 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3045.000000
07-01 17:05:05 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 48.000000
07-01 17:05:05 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 159.000000
07-01 17:05:05 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2973.000000
07-01 17:05:05 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 17:05:05 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 784.000000
07-01 17:05:05 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:05:05 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 33.000000
07-01 17:05:05 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 15.000000
07-01 17:05:05 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 25.000000
07-01 17:05:05 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 779.000000
07-01 17:05:05 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 99.000000
07-01 17:05:05 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 160.000000
07-01 17:05:05 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 20.000000
07-01 17:05:05 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:05:05 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 17:05:05 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3766.000000
07-01 17:05:05 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 779.000000
07-01 17:05:05 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 35.000000
07-01 17:05:05 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 672.000000
07-01 17:05:05 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 93.000000
07-01 17:05:05 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 10.000000
07-01 17:05:05 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 17:05:05 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 43.000000
07-01 17:05:05 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3045.000000
07-01 17:05:05 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 48.000000
07-01 17:05:05 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 159.000000
07-01 17:05:05 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2973.000000
07-01 17:05:05 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 784.000000
07-01 17:05:05 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:05:05 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 33.000000
07-01 17:05:05 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 15.000000
07-01 17:05:05 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 25.000000
07-01 17:05:05 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 779.000000
07-01 17:05:05 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 99.000000
07-01 17:05:05 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 160.000000
07-01 17:05:05 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 20.000000
07-01 17:05:05 I/MeasurementPrinter:                   160.0	                OK	                 3757.0	                OK	                            784.0	                OK
07-01 17:05:05 D/RunUtil: Running command null without timeout.
07-01 17:05:05 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:05:06 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:05:06 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:05:06 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:05:06 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:05:06 D/RunUtil: Running command null without timeout.
07-01 17:05:07 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:05:07 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:05:07 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:05:07 D/RunUtil: Running command null without timeout.
07-01 17:05:07 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:05:07 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:05:07 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 17:05:07 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 17:05:07 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 17:05:07 I/NativeDeviceStateMonitor: Waiting 359903 ms for device SKBFXYZO0DTQ external store
07-01 17:05:08 D/RunUtil: Running command null without timeout.
07-01 17:05:08 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:05:08 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:05:08 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:05:08 D/RunUtil: Running command null without timeout.
07-01 17:05:08 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:05:08 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:05:08 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:05:09 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:05:09 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 17:05:09 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:05:09 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_58_46.235_5299138291386649122.txt] without timeout.
07-01 17:05:09 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 17:05:09 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 17:05:09 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 17:05:09 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 17:05:37 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:05:37 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 17:05:37 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3755.000000
07-01 17:05:37 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 776.000000
07-01 17:05:37 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 30.000000
07-01 17:05:37 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 624.000000
07-01 17:05:37 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 43.000000
07-01 17:05:37 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 26.000000
07-01 17:05:37 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 18.000000
07-01 17:05:37 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 108.000000
07-01 17:05:37 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3039.000000
07-01 17:05:37 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 51.000000
07-01 17:05:37 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 148.000000
07-01 17:05:37 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2959.000000
07-01 17:05:37 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 781.000000
07-01 17:05:37 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 35.000000
07-01 17:05:37 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 16.000000
07-01 17:05:37 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 19.000000
07-01 17:05:37 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 787.000000
07-01 17:05:37 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 53.000000
07-01 17:05:37 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 151.000000
07-01 17:05:37 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 17:05:37 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 30.000000
07-01 17:05:37 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:05:37 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 17:05:37 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3755.000000
07-01 17:05:37 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 776.000000
07-01 17:05:37 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 30.000000
07-01 17:05:37 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 624.000000
07-01 17:05:37 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 43.000000
07-01 17:05:37 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 26.000000
07-01 17:05:37 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 18.000000
07-01 17:05:37 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 108.000000
07-01 17:05:37 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3039.000000
07-01 17:05:37 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 51.000000
07-01 17:05:37 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 148.000000
07-01 17:05:37 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2959.000000
07-01 17:05:37 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 17:05:37 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 781.000000
07-01 17:05:37 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:05:37 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 35.000000
07-01 17:05:37 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 16.000000
07-01 17:05:37 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 19.000000
07-01 17:05:37 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 787.000000
07-01 17:05:37 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 53.000000
07-01 17:05:37 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 151.000000
07-01 17:05:37 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 30.000000
07-01 17:05:37 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:05:37 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 17:05:37 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3755.000000
07-01 17:05:37 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 776.000000
07-01 17:05:37 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 30.000000
07-01 17:05:37 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 624.000000
07-01 17:05:37 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 43.000000
07-01 17:05:37 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 26.000000
07-01 17:05:37 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 18.000000
07-01 17:05:37 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 108.000000
07-01 17:05:37 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3039.000000
07-01 17:05:37 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 51.000000
07-01 17:05:37 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 148.000000
07-01 17:05:37 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2959.000000
07-01 17:05:37 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 781.000000
07-01 17:05:37 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:05:37 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 35.000000
07-01 17:05:37 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 16.000000
07-01 17:05:37 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 19.000000
07-01 17:05:37 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 787.000000
07-01 17:05:37 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 53.000000
07-01 17:05:37 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 151.000000
07-01 17:05:37 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 30.000000
07-01 17:05:37 I/MeasurementPrinter:                   151.0	                OK	                 3740.0	                OK	                            781.0	                OK
07-01 17:05:37 D/RunUtil: Running command null without timeout.
07-01 17:05:37 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:05:38 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:05:38 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:05:38 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:05:38 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:05:38 D/RunUtil: Running command null without timeout.
07-01 17:05:38 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:05:39 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:05:39 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:05:39 D/RunUtil: Running command null without timeout.
07-01 17:05:39 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:05:39 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:05:39 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 17:05:39 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 17:05:39 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 17:05:39 I/NativeDeviceStateMonitor: Waiting 359901 ms for device SKBFXYZO0DTQ external store
07-01 17:05:40 D/RunUtil: Running command null without timeout.
07-01 17:05:40 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:05:40 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:05:40 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:05:40 D/RunUtil: Running command null without timeout.
07-01 17:05:40 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:05:40 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:05:40 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:05:41 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:05:41 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 17:05:41 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:05:41 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_58_46.235_14533206638123614351.txt] without timeout.
07-01 17:05:41 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 17:05:41 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 17:05:41 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 17:05:41 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 17:06:08 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:06:08 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 17:06:08 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3362.000000
07-01 17:06:08 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 797.000000
07-01 17:06:08 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 30.000000
07-01 17:06:08 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 444.000000
07-01 17:06:08 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 43.000000
07-01 17:06:08 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 30.000000
07-01 17:06:08 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 3.000000
07-01 17:06:08 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 269.000000
07-01 17:06:08 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2603.000000
07-01 17:06:08 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 49.000000
07-01 17:06:08 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 153.000000
07-01 17:06:08 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2549.000000
07-01 17:06:08 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 813.000000
07-01 17:06:08 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 28.000000
07-01 17:06:08 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 20.000000
07-01 17:06:08 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 13.000000
07-01 17:06:08 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 799.000000
07-01 17:06:08 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 72.000000
07-01 17:06:08 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 154.000000
07-01 17:06:08 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 17:06:08 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 36.000000
07-01 17:06:08 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:06:08 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 17:06:08 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3362.000000
07-01 17:06:08 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 797.000000
07-01 17:06:08 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 30.000000
07-01 17:06:08 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 444.000000
07-01 17:06:08 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 43.000000
07-01 17:06:08 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 30.000000
07-01 17:06:08 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 3.000000
07-01 17:06:08 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 269.000000
07-01 17:06:08 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2603.000000
07-01 17:06:08 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 49.000000
07-01 17:06:08 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 153.000000
07-01 17:06:08 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2549.000000
07-01 17:06:08 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 17:06:08 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 813.000000
07-01 17:06:08 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:06:08 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 28.000000
07-01 17:06:08 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 20.000000
07-01 17:06:08 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 13.000000
07-01 17:06:08 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 799.000000
07-01 17:06:08 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 72.000000
07-01 17:06:08 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 154.000000
07-01 17:06:08 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 36.000000
07-01 17:06:08 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:06:08 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 17:06:08 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3362.000000
07-01 17:06:08 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 797.000000
07-01 17:06:08 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 30.000000
07-01 17:06:08 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 444.000000
07-01 17:06:08 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 43.000000
07-01 17:06:08 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 30.000000
07-01 17:06:08 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 3.000000
07-01 17:06:08 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 269.000000
07-01 17:06:08 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2603.000000
07-01 17:06:08 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 49.000000
07-01 17:06:08 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 153.000000
07-01 17:06:08 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2549.000000
07-01 17:06:08 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 813.000000
07-01 17:06:08 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:06:08 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 28.000000
07-01 17:06:08 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 20.000000
07-01 17:06:08 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 13.000000
07-01 17:06:08 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 799.000000
07-01 17:06:08 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 72.000000
07-01 17:06:08 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 154.000000
07-01 17:06:08 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 36.000000
07-01 17:06:08 I/MeasurementPrinter:                   154.0	                OK	                 3362.0	                OK	                            813.0	                OK
07-01 17:06:08 D/RunUtil: Running command null without timeout.
07-01 17:06:08 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:06:09 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:06:09 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:06:09 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:06:09 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:06:09 D/RunUtil: Running command null without timeout.
07-01 17:06:10 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:06:10 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:06:10 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:06:10 D/RunUtil: Running command null without timeout.
07-01 17:06:10 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:06:10 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:06:10 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 17:06:10 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 17:06:10 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 17:06:10 I/NativeDeviceStateMonitor: Waiting 359902 ms for device SKBFXYZO0DTQ external store
07-01 17:06:11 D/RunUtil: Running command null without timeout.
07-01 17:06:11 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:06:11 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:06:11 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:06:11 D/RunUtil: Running command null without timeout.
07-01 17:06:11 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:06:11 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:06:11 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:06:12 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:06:12 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 17:06:12 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:06:12 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_58_46.235_16946590255104847233.txt] without timeout.
07-01 17:06:12 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 17:06:12 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 17:06:12 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 17:06:12 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 17:06:39 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:06:39 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 17:06:39 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3780.000000
07-01 17:06:39 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 799.000000
07-01 17:06:39 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 6.000000
07-01 17:06:39 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 579.000000
07-01 17:06:39 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 71.000000
07-01 17:06:39 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 18.000000
07-01 17:06:39 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 17:06:39 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 110.000000
07-01 17:06:39 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3038.000000
07-01 17:06:39 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 55.000000
07-01 17:06:39 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 129.000000
07-01 17:06:39 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2963.000000
07-01 17:06:39 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 811.000000
07-01 17:06:39 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 45.000000
07-01 17:06:39 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 11.000000
07-01 17:06:39 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 15.000000
07-01 17:06:39 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 801.000000
07-01 17:06:39 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 53.000000
07-01 17:06:39 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 128.000000
07-01 17:06:39 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 17:06:39 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 23.000000
07-01 17:06:39 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:06:40 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 17:06:40 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3780.000000
07-01 17:06:40 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 799.000000
07-01 17:06:40 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 6.000000
07-01 17:06:40 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 579.000000
07-01 17:06:40 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 71.000000
07-01 17:06:40 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 18.000000
07-01 17:06:40 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 17:06:40 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 110.000000
07-01 17:06:40 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3038.000000
07-01 17:06:40 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 55.000000
07-01 17:06:40 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 129.000000
07-01 17:06:40 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2963.000000
07-01 17:06:40 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 17:06:40 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 811.000000
07-01 17:06:40 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:06:40 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 45.000000
07-01 17:06:40 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 11.000000
07-01 17:06:40 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 15.000000
07-01 17:06:40 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 801.000000
07-01 17:06:40 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 53.000000
07-01 17:06:40 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 128.000000
07-01 17:06:40 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 23.000000
07-01 17:06:40 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:06:40 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 17:06:40 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3780.000000
07-01 17:06:40 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 799.000000
07-01 17:06:40 D/ProtoMeasurementBase: searchbar_state_idle: size = 1, sum: 6.000000
07-01 17:06:40 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 579.000000
07-01 17:06:40 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 71.000000
07-01 17:06:40 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 18.000000
07-01 17:06:40 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 2.000000
07-01 17:06:40 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 110.000000
07-01 17:06:40 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3038.000000
07-01 17:06:40 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 55.000000
07-01 17:06:40 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 129.000000
07-01 17:06:40 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2963.000000
07-01 17:06:40 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 811.000000
07-01 17:06:40 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:06:40 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 45.000000
07-01 17:06:40 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 11.000000
07-01 17:06:40 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 15.000000
07-01 17:06:40 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 801.000000
07-01 17:06:40 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 53.000000
07-01 17:06:40 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 128.000000
07-01 17:06:40 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 23.000000
07-01 17:06:40 I/MeasurementPrinter:                   128.0	                OK	                 3774.0	                OK	                            811.0	                OK
07-01 17:06:40 D/RunUtil: Running command null without timeout.
07-01 17:06:40 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:06:40 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:06:40 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:06:41 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:06:41 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:06:41 D/RunUtil: Running command null without timeout.
07-01 17:06:41 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:06:41 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:06:41 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:06:41 D/RunUtil: Running command null without timeout.
07-01 17:06:41 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:06:41 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:06:41 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 17:06:41 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 17:06:41 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 17:06:41 I/NativeDeviceStateMonitor: Waiting 359877 ms for device SKBFXYZO0DTQ external store
07-01 17:06:43 D/RunUtil: Running command null without timeout.
07-01 17:06:43 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:06:43 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:06:43 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:06:43 D/RunUtil: Running command null without timeout.
07-01 17:06:43 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:06:43 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:06:43 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:06:43 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:06:43 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 17:06:44 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:06:44 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_58_46.235_4088285172715418452.txt] without timeout.
07-01 17:06:44 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 17:06:44 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 17:06:44 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 17:06:44 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 17:07:10 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:07:10 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 17:07:10 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3299.000000
07-01 17:07:10 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 749.000000
07-01 17:07:10 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 19.000000
07-01 17:07:10 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 690.000000
07-01 17:07:10 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 45.000000
07-01 17:07:10 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 8.000000
07-01 17:07:10 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 11.000000
07-01 17:07:10 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 407.000000
07-01 17:07:10 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2609.000000
07-01 17:07:10 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 9.000000
07-01 17:07:10 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 122.000000
07-01 17:07:10 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2544.000000
07-01 17:07:10 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 751.000000
07-01 17:07:10 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 4.000000
07-01 17:07:10 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 5.000000
07-01 17:07:10 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 24.000000
07-01 17:07:10 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 750.000000
07-01 17:07:10 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 94.000000
07-01 17:07:10 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 122.000000
07-01 17:07:10 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 17:07:10 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 23.000000
07-01 17:07:10 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:07:11 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 17:07:11 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3299.000000
07-01 17:07:11 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 749.000000
07-01 17:07:11 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 19.000000
07-01 17:07:11 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 690.000000
07-01 17:07:11 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 45.000000
07-01 17:07:11 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 8.000000
07-01 17:07:11 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 11.000000
07-01 17:07:11 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 407.000000
07-01 17:07:11 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2609.000000
07-01 17:07:11 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 9.000000
07-01 17:07:11 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 122.000000
07-01 17:07:11 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2544.000000
07-01 17:07:11 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 17:07:11 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 751.000000
07-01 17:07:11 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:07:11 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 4.000000
07-01 17:07:11 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 5.000000
07-01 17:07:11 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 24.000000
07-01 17:07:11 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 750.000000
07-01 17:07:11 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 94.000000
07-01 17:07:11 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 122.000000
07-01 17:07:11 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 23.000000
07-01 17:07:11 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:07:11 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 17:07:11 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3299.000000
07-01 17:07:11 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 749.000000
07-01 17:07:11 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 19.000000
07-01 17:07:11 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 690.000000
07-01 17:07:11 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 45.000000
07-01 17:07:11 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 8.000000
07-01 17:07:11 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 11.000000
07-01 17:07:11 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 407.000000
07-01 17:07:11 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2609.000000
07-01 17:07:11 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 9.000000
07-01 17:07:11 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 122.000000
07-01 17:07:11 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2544.000000
07-01 17:07:11 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 751.000000
07-01 17:07:11 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:07:11 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 4.000000
07-01 17:07:11 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 5.000000
07-01 17:07:11 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 24.000000
07-01 17:07:11 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 750.000000
07-01 17:07:11 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 94.000000
07-01 17:07:11 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 122.000000
07-01 17:07:11 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 23.000000
07-01 17:07:11 I/MeasurementPrinter:                   122.0	                OK	                 3295.0	                OK	                            751.0	                OK
07-01 17:07:11 D/RunUtil: Running command null without timeout.
07-01 17:07:11 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:07:11 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:07:11 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:07:12 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:07:12 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:07:12 D/RunUtil: Running command null without timeout.
07-01 17:07:12 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:07:12 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:07:12 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:07:12 D/RunUtil: Running command null without timeout.
07-01 17:07:12 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:07:12 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:07:12 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 17:07:12 I/NativeDeviceStateMonitor: Waiting 359999 ms for device SKBFXYZO0DTQ boot complete
07-01 17:07:12 I/DeviceStateMonitor: Waiting 359963 ms for device SKBFXYZO0DTQ package manager
07-01 17:07:13 I/NativeDeviceStateMonitor: Waiting 359901 ms for device SKBFXYZO0DTQ external store
07-01 17:07:14 D/RunUtil: Running command null without timeout.
07-01 17:07:14 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:07:14 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:07:14 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:07:14 D/RunUtil: Running command null without timeout.
07-01 17:07:14 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:07:14 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:07:14 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:07:14 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:07:14 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 17:07:15 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:07:15 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_58_46.235_7227210749652969956.txt] without timeout.
07-01 17:07:15 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 17:07:15 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 17:07:15 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 17:07:15 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 17:07:42 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:07:42 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 17:07:42 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3588.000000
07-01 17:07:42 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 812.000000
07-01 17:07:42 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 31.000000
07-01 17:07:42 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 416.000000
07-01 17:07:42 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 71.000000
07-01 17:07:42 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 10.000000
07-01 17:07:42 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 4.000000
07-01 17:07:42 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 157.000000
07-01 17:07:42 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2816.000000
07-01 17:07:42 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 45.000000
07-01 17:07:42 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 121.000000
07-01 17:07:42 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2762.000000
07-01 17:07:42 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 813.000000
07-01 17:07:42 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 19.000000
07-01 17:07:42 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 27.000000
07-01 17:07:42 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 19.000000
07-01 17:07:42 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 814.000000
07-01 17:07:42 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 61.000000
07-01 17:07:42 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 121.000000
07-01 17:07:42 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 17:07:42 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 23.000000
07-01 17:07:42 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:07:42 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 17:07:42 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3588.000000
07-01 17:07:42 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 812.000000
07-01 17:07:42 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 31.000000
07-01 17:07:42 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 416.000000
07-01 17:07:42 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 71.000000
07-01 17:07:42 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 10.000000
07-01 17:07:42 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 4.000000
07-01 17:07:42 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 157.000000
07-01 17:07:42 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2816.000000
07-01 17:07:42 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 45.000000
07-01 17:07:42 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 121.000000
07-01 17:07:42 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2762.000000
07-01 17:07:42 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 17:07:42 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 813.000000
07-01 17:07:42 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:07:42 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 19.000000
07-01 17:07:42 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 27.000000
07-01 17:07:42 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 19.000000
07-01 17:07:42 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 814.000000
07-01 17:07:42 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 61.000000
07-01 17:07:42 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 121.000000
07-01 17:07:42 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 23.000000
07-01 17:07:42 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:07:42 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 17:07:42 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3588.000000
07-01 17:07:42 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 812.000000
07-01 17:07:42 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 31.000000
07-01 17:07:42 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 416.000000
07-01 17:07:42 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 71.000000
07-01 17:07:42 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 10.000000
07-01 17:07:42 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 4.000000
07-01 17:07:42 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 157.000000
07-01 17:07:42 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2816.000000
07-01 17:07:42 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 45.000000
07-01 17:07:42 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 121.000000
07-01 17:07:42 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2762.000000
07-01 17:07:42 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 813.000000
07-01 17:07:42 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:07:42 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 19.000000
07-01 17:07:42 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 27.000000
07-01 17:07:42 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 19.000000
07-01 17:07:42 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 814.000000
07-01 17:07:42 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 61.000000
07-01 17:07:42 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 121.000000
07-01 17:07:42 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 23.000000
07-01 17:07:42 I/MeasurementPrinter:                   121.0	                OK	                 3575.0	                OK	                            813.0	                OK
07-01 17:07:42 D/RunUtil: Running command null without timeout.
07-01 17:07:43 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:07:43 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:07:43 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:07:43 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:07:44 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:07:44 D/RunUtil: Running command null without timeout.
07-01 17:07:44 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:07:44 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:07:44 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:07:44 D/RunUtil: Running command null without timeout.
07-01 17:07:44 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:07:44 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:07:44 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 17:07:44 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 17:07:44 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 17:07:44 I/NativeDeviceStateMonitor: Waiting 359902 ms for device SKBFXYZO0DTQ external store
07-01 17:07:45 D/RunUtil: Running command null without timeout.
07-01 17:07:45 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:07:45 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:07:45 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:07:45 D/RunUtil: Running command null without timeout.
07-01 17:07:45 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:07:46 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:07:46 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:07:46 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:07:46 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 17:07:46 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:07:46 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_58_46.235_11280873930294091655.txt] without timeout.
07-01 17:07:47 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 17:07:47 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 17:07:47 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 17:07:47 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 17:08:13 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:08:14 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 17:08:14 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3710.000000
07-01 17:08:14 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 875.000000
07-01 17:08:14 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 37.000000
07-01 17:08:14 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 655.000000
07-01 17:08:14 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 37.000000
07-01 17:08:14 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 19.000000
07-01 17:08:14 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 17:08:14 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 110.000000
07-01 17:08:14 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2903.000000
07-01 17:08:14 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 29.000000
07-01 17:08:14 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 153.000000
07-01 17:08:14 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2827.000000
07-01 17:08:14 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 877.000000
07-01 17:08:14 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 22.000000
07-01 17:08:14 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 7.000000
07-01 17:08:14 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 26.000000
07-01 17:08:14 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 877.000000
07-01 17:08:14 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 104.000000
07-01 17:08:14 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 154.000000
07-01 17:08:14 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 17:08:14 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 48.000000
07-01 17:08:14 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:08:14 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 17:08:14 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3710.000000
07-01 17:08:14 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 875.000000
07-01 17:08:14 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 37.000000
07-01 17:08:14 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 655.000000
07-01 17:08:14 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 37.000000
07-01 17:08:14 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 19.000000
07-01 17:08:14 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 17:08:14 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 110.000000
07-01 17:08:14 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2903.000000
07-01 17:08:14 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 29.000000
07-01 17:08:14 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 153.000000
07-01 17:08:14 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2827.000000
07-01 17:08:14 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 17:08:14 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 877.000000
07-01 17:08:14 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:08:14 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 22.000000
07-01 17:08:14 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 7.000000
07-01 17:08:14 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 26.000000
07-01 17:08:14 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 877.000000
07-01 17:08:14 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 104.000000
07-01 17:08:14 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 154.000000
07-01 17:08:14 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 48.000000
07-01 17:08:14 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:08:14 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 17:08:14 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3710.000000
07-01 17:08:14 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 875.000000
07-01 17:08:14 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 37.000000
07-01 17:08:14 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 655.000000
07-01 17:08:14 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 37.000000
07-01 17:08:14 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 19.000000
07-01 17:08:14 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 17:08:14 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 110.000000
07-01 17:08:14 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 2903.000000
07-01 17:08:14 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 29.000000
07-01 17:08:14 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 153.000000
07-01 17:08:14 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 2827.000000
07-01 17:08:14 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 877.000000
07-01 17:08:14 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:08:14 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 22.000000
07-01 17:08:14 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 7.000000
07-01 17:08:14 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 26.000000
07-01 17:08:14 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 877.000000
07-01 17:08:14 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 104.000000
07-01 17:08:14 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 154.000000
07-01 17:08:14 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 48.000000
07-01 17:08:14 I/MeasurementPrinter:                   154.0	                OK	                 3704.0	                OK	                            877.0	                OK
07-01 17:08:14 D/RunUtil: Running command null without timeout.
07-01 17:08:14 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:08:14 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:08:14 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:08:15 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:08:15 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:08:15 D/RunUtil: Running command null without timeout.
07-01 17:08:15 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:08:15 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:08:15 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:08:15 D/RunUtil: Running command null without timeout.
07-01 17:08:15 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:08:15 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:08:15 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 17:08:15 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 17:08:15 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 17:08:16 I/NativeDeviceStateMonitor: Waiting 359902 ms for device SKBFXYZO0DTQ external store
07-01 17:08:17 D/RunUtil: Running command null without timeout.
07-01 17:08:17 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:08:17 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:08:17 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:08:17 D/RunUtil: Running command null without timeout.
07-01 17:08:17 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:08:17 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:08:17 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:08:18 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:08:18 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 17:08:18 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:08:18 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_58_46.235_17338802974038417952.txt] without timeout.
07-01 17:08:18 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 17:08:18 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 17:08:18 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 17:08:18 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 17:08:46 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:08:46 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 17:08:46 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3920.000000
07-01 17:08:46 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 812.000000
07-01 17:08:46 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 21.000000
07-01 17:08:46 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 671.000000
07-01 17:08:46 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 80.000000
07-01 17:08:46 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 22.000000
07-01 17:08:46 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 7.000000
07-01 17:08:46 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 100.000000
07-01 17:08:46 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3177.000000
07-01 17:08:46 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 58.000000
07-01 17:08:46 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 146.000000
07-01 17:08:46 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 3094.000000
07-01 17:08:46 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 816.000000
07-01 17:08:46 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 36.000000
07-01 17:08:46 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 22.000000
07-01 17:08:46 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 57.000000
07-01 17:08:46 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 812.000000
07-01 17:08:46 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 59.000000
07-01 17:08:46 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 148.000000
07-01 17:08:46 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 17:08:46 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 34.000000
07-01 17:08:46 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:08:46 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 17:08:46 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3920.000000
07-01 17:08:46 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 812.000000
07-01 17:08:46 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 21.000000
07-01 17:08:46 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 671.000000
07-01 17:08:46 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 80.000000
07-01 17:08:46 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 22.000000
07-01 17:08:46 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 7.000000
07-01 17:08:46 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 100.000000
07-01 17:08:46 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3177.000000
07-01 17:08:46 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 58.000000
07-01 17:08:46 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 146.000000
07-01 17:08:46 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 3094.000000
07-01 17:08:46 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 17:08:46 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 816.000000
07-01 17:08:46 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:08:46 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 36.000000
07-01 17:08:46 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 22.000000
07-01 17:08:46 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 57.000000
07-01 17:08:46 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 812.000000
07-01 17:08:46 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 59.000000
07-01 17:08:46 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 148.000000
07-01 17:08:46 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 34.000000
07-01 17:08:46 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:08:46 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 17:08:46 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3920.000000
07-01 17:08:46 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 812.000000
07-01 17:08:46 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 21.000000
07-01 17:08:46 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 671.000000
07-01 17:08:46 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 3, sum: 80.000000
07-01 17:08:46 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 22.000000
07-01 17:08:46 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 7.000000
07-01 17:08:46 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 100.000000
07-01 17:08:46 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3177.000000
07-01 17:08:46 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 58.000000
07-01 17:08:46 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 146.000000
07-01 17:08:46 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 3094.000000
07-01 17:08:46 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 816.000000
07-01 17:08:46 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:08:46 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 36.000000
07-01 17:08:46 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 22.000000
07-01 17:08:46 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 57.000000
07-01 17:08:46 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 812.000000
07-01 17:08:46 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 59.000000
07-01 17:08:46 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 148.000000
07-01 17:08:46 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 34.000000
07-01 17:08:46 I/MeasurementPrinter:                   148.0	                OK	                 3910.0	                OK	                            816.0	                OK
07-01 17:08:46 D/RunUtil: Running command null without timeout.
07-01 17:08:46 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:08:47 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:08:47 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:08:47 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:08:47 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:08:47 D/RunUtil: Running command null without timeout.
07-01 17:08:48 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:08:48 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:08:48 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:08:48 D/RunUtil: Running command null without timeout.
07-01 17:08:48 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:08:48 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:08:48 I/NativeDeviceStateMonitor: Device SKBFXYZO0DTQ is already ONLINE
07-01 17:08:48 I/NativeDeviceStateMonitor: Waiting 360000 ms for device SKBFXYZO0DTQ boot complete
07-01 17:08:48 I/DeviceStateMonitor: Waiting 359964 ms for device SKBFXYZO0DTQ package manager
07-01 17:08:48 I/NativeDeviceStateMonitor: Waiting 359902 ms for device SKBFXYZO0DTQ external store
07-01 17:08:49 D/RunUtil: Running command null without timeout.
07-01 17:08:49 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:08:49 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:08:49 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:08:49 D/RunUtil: Running command null without timeout.
07-01 17:08:49 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:08:49 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:08:49 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:08:50 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:08:50 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, logcat, -c, -b, all] with timeout: 2m 0s
07-01 17:08:50 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:08:50 D/RunUtil: Running command [/bin/bash, -c, rm -f /data/tmp/ec3c9886-ee61-4596-bf4a-2009f6303a31/tmp/katniss-primes-2024-07-01T00_58_46.235_14738216206463436471.txt] without timeout.
07-01 17:08:50 D/AppRunnerUtils: Cannot find process for package com.google.android.assistant.tvts
07-01 17:08:50 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.assistant.tvts
07-01 17:08:50 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.assistant.tvts] with timeout: 2m 0s
07-01 17:08:50 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, instrument, -e, class, com.google.android.assistant.tvts.HeavyDeviceUsageTest, -w, com.google.android.assistant.tvts/androidx.test.runner.AndroidJUnitRunner] with timeout: 4m 10s
07-01 17:09:17 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:09:17 D/ProtoMeasurementBase: Begin Parse: search_to_query_latency
07-01 17:09:17 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3795.000000
07-01 17:09:17 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 759.000000
07-01 17:09:17 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 37.000000
07-01 17:09:17 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 622.000000
07-01 17:09:17 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 8.000000
07-01 17:09:17 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 17:09:17 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 3.000000
07-01 17:09:17 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 131.000000
07-01 17:09:17 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3105.000000
07-01 17:09:17 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 18.000000
07-01 17:09:17 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 63.000000
07-01 17:09:17 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 3022.000000
07-01 17:09:17 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 771.000000
07-01 17:09:17 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 8.000000
07-01 17:09:17 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 11.000000
07-01 17:09:17 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 14.000000
07-01 17:09:17 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 760.000000
07-01 17:09:17 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 40.000000
07-01 17:09:17 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 62.000000
07-01 17:09:17 D/ProtoMeasurementBase: Adding required latency: assistant_request_intent_to_ui
07-01 17:09:17 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 30.000000
07-01 17:09:17 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:09:17 D/ProtoMeasurementBase: Begin Parse: query_to_result_latency
07-01 17:09:17 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3795.000000
07-01 17:09:17 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 759.000000
07-01 17:09:17 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 37.000000
07-01 17:09:17 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 622.000000
07-01 17:09:17 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 8.000000
07-01 17:09:17 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 17:09:17 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 3.000000
07-01 17:09:17 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 131.000000
07-01 17:09:17 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3105.000000
07-01 17:09:17 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 18.000000
07-01 17:09:17 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 63.000000
07-01 17:09:17 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 3022.000000
07-01 17:09:17 D/ProtoMeasurementBase: Adding required latency: query_recognized_to_result
07-01 17:09:17 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 771.000000
07-01 17:09:17 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:09:17 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 8.000000
07-01 17:09:17 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 11.000000
07-01 17:09:17 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 14.000000
07-01 17:09:17 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 760.000000
07-01 17:09:17 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 40.000000
07-01 17:09:17 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 62.000000
07-01 17:09:17 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 30.000000
07-01 17:09:17 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:09:18 D/ProtoMeasurementBase: Begin Parse: query_to_result_no_server_latency
07-01 17:09:18 D/ProtoMeasurementBase: e2e_text_response: size = 1, sum: 3795.000000
07-01 17:09:18 D/ProtoMeasurementBase: new_result_displayed_text_warm: size = 1, sum: 759.000000
07-01 17:09:18 D/ProtoMeasurementBase: searchbar_state_idle: size = 2, sum: 37.000000
07-01 17:09:18 D/ProtoMeasurementBase: fetch_zerostate_suggestions_from_server: size = 1, sum: 622.000000
07-01 17:09:18 D/ProtoMeasurementBase: searchbar_state_talking_idle: size = 2, sum: 8.000000
07-01 17:09:18 D/ProtoMeasurementBase: hot_voiceplate_activity_new_intent_to_resume: size = 1, sum: 1.000000
07-01 17:09:18 D/ProtoMeasurementBase: hot_voiceplate_activity_start_to_new_intent: size = 1, sum: 3.000000
07-01 17:09:18 D/ProtoMeasurementBase: searchbar_state_loading: size = 1, sum: 131.000000
07-01 17:09:18 D/ProtoMeasurementBase: query_recognized_to_tts_start: size = 1, sum: 3105.000000
07-01 17:09:18 D/ProtoMeasurementBase: app_start_to_vpa_visible: size = 1, sum: 18.000000
07-01 17:09:18 D/ProtoMeasurementBase: new_assistant_request_intent_to_ui_hot: size = 1, sum: 63.000000
07-01 17:09:18 D/ProtoMeasurementBase: query_recognized_to_result: size = 1, sum: 3022.000000
07-01 17:09:18 D/ProtoMeasurementBase: result_displayed_text: size = 1, sum: 771.000000
07-01 17:09:18 D/ProtoMeasurementBase: Adding required latency: result_displayed_text
07-01 17:09:18 D/ProtoMeasurementBase: hot_voiceplate_activity_restart_to_start: size = 1, sum: 8.000000
07-01 17:09:18 D/ProtoMeasurementBase: hot_voiceplate_service_on_start_command_to_restart: size = 1, sum: 11.000000
07-01 17:09:18 D/ProtoMeasurementBase: view_add_text: size = 1, sum: 14.000000
07-01 17:09:18 D/ProtoMeasurementBase: e2e_client_text_response: size = 1, sum: 760.000000
07-01 17:09:18 D/ProtoMeasurementBase: hot_voiceplate_activity_resume_to_first_draw: size = 1, sum: 40.000000
07-01 17:09:18 D/ProtoMeasurementBase: assistant_request_intent_to_ui: size = 1, sum: 62.000000
07-01 17:09:18 D/ProtoMeasurementBase: result_across_process: size = 1, sum: 30.000000
07-01 17:09:18 I/MeasurementPrinter:                    62.0	                OK	                 3793.0	                OK	                            771.0	                OK
07-01 17:09:18 D/RunUtil: Running command null without timeout.
07-01 17:09:18 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:09:18 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:09:18 D/AppRunnerUtils: waitUntilAppInForeground(): Checking activity: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:09:18 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:09:19 D/AppRunnerUtils: getActivityShownByPackage(): ActivityName: com.skb.google.tv.main.MainActivity.
07-01 17:09:19 D/RunUtil: Running command null without timeout.
07-01 17:09:19 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:09:19 D/AppRunnerUtils: getHomeActivityName(): launcherActivityName: com.skb.tv/com.skb.google.tv.main.MainActivity
07-01 17:09:19 D/AppRunnerUtils: pressHomeUntilBackHome(): poll time: 500  totalTime: 4000  attempts: 8
07-01 17:09:19 D/RunUtil: Running command null without timeout.
07-01 17:09:19 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:09:19 D/AppRunnerUtils: foregroundActivitySet(): [com.skb.tv/com.skb.google.tv.main.MainActivity]
07-01 17:09:20 D/AppRunnerUtils: Running the following command: am force-stop com.google.android.katniss
07-01 17:09:20 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, am, force-stop, com.google.android.katniss] with timeout: 2m 0s
07-01 17:09:20 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:09:20 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:09:20 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:09:20 D/NativeDevice: Using property ro.build.version.sdk=29 from cache.
07-01 17:09:20 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, getprop, ro.config.low_ram] with timeout: 45s
07-01 17:09:20 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, pm, list, features] with timeout: 2m 0s
07-01 17:09:21 W/TestDevice: Feature: feature:com.google.android.feature.AMATI_EXPERIENCE is not available on SKBFXYZO0DTQ
07-01 17:09:21 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, getprop, ro.config.low_ram] with timeout: 45s
07-01 17:09:21 D/RunUtil: Running command [adb, -s, SKBFXYZO0DTQ, shell, pm, list, features] with timeout: 2m 0s
07-01 17:09:21 W/TestDevice: Feature: feature:com.google.android.feature.AMATI_EXPERIENCE is not available on SKBFXYZO0DTQ
07-01 17:09:21 I/BasicAggregationTypeBase: Checking if collection is initialized with data
07-01 17:09:21 I/BasicAggregationTypeBase: Checking if collection is initialized with data
07-01 17:09:21 D/PrettyTestEventLogger: 
==================== com.google.android.assistant.v4.tvts.TvtsAssistant4Test#testHeavyDeviceUsage ENDED: Mon Jul 01 17:09:21 KST 2024 ====================
